2012-07-26 18:40:58,698 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44331
2012-07-26 18:40:58,714 INFO  [MediaServer] ------------------------------------------------------------------------
2012-07-26 18:40:58,714 INFO  [MediaServer] Serviio DLNA media streaming server v 1.0 (rev. 34c915f37185+)
2012-07-26 18:40:58,714 INFO  [MediaServer] Petr Nejedly 2009-2012
2012-07-26 18:40:58,714 INFO  [MediaServer] http://www.serviio.org
2012-07-26 18:40:58,714 INFO  [MediaServer] 
2012-07-26 18:40:58,714 INFO  [MediaServer] Java 1.7.0_05-Oracle Corporation
2012-07-26 18:40:58,714 INFO  [MediaServer] OS Windows 7 amd64 6.1
2012-07-26 18:40:58,714 INFO  [MediaServer] File encoding: windows-1252
2012-07-26 18:40:58,714 INFO  [MediaServer] Headless mode enabled: true
2012-07-26 18:40:58,714 INFO  [MediaServer] User home dir: C:\
2012-07-26 18:40:58,714 INFO  [MediaServer] Temp dir: C:\Windows\TEMP\
2012-07-26 18:40:58,714 INFO  [MediaServer] ------------------------------------------------------------------------
2012-07-26 18:40:59,572 INFO  [WebServer] Socket buffer set to 65535 bytes
2012-07-26 18:40:59,572 INFO  [RestletServer] Starting Restlet server (/rest) exposed on port 23423
2012-07-26 18:40:59,712 INFO  [RestletServer] Starting Restlet server (/cds) exposed on port 23424
2012-07-26 18:40:59,712 INFO  [RestletServer] Starting Restlet server (/mediabrowser) exposed on port 23424
2012-07-26 18:40:59,728 INFO  [DBSchemaUpdateExecutor] Checking if DB schema needs to be updated
2012-07-26 18:40:59,759 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.sql' has been run
2012-07-26 18:41:03,035 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.1.sql' has been run
2012-07-26 18:41:03,035 DEBUG [DBLogDAOImpl] Checking if script 'script-0.2.sql' has been run
2012-07-26 18:41:03,035 DEBUG [DBLogDAOImpl] Checking if script 'script-0.3.1.sql' has been run
2012-07-26 18:41:03,035 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.sql' has been run
2012-07-26 18:41:03,035 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.1.sql' has been run
2012-07-26 18:41:03,035 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.2.sql' has been run
2012-07-26 18:41:03,035 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.sql' has been run
2012-07-26 18:41:03,035 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.1.sql' has been run
2012-07-26 18:41:03,035 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.sql' has been run
2012-07-26 18:41:03,035 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.1.sql' has been run
2012-07-26 18:41:03,035 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.2.sql' has been run
2012-07-26 18:41:03,035 DEBUG [DBLogDAOImpl] Checking if script 'script-1.0.sql' has been run
2012-07-26 18:41:03,051 DEBUG [ConfigEntryDAOImpl] Reading all ConfigEntries
2012-07-26 18:41:05,217 INFO  [MediaServer] ------------------------------------------------------------------------
2012-07-26 18:41:05,217 INFO  [MediaServer] License: PRO (EVALUATION, id: 11111111-1111-1111-1111-111111111113)
2012-07-26 18:41:05,217 INFO  [MediaServer] ------------------------------------------------------------------------
2012-07-26 18:41:05,537 DEBUG [ServiioConsole] Initializing Serviio Console
2012-07-26 18:41:05,539 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2012-07-26 18:41:05,569 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44332
2012-07-26 18:41:05,569 INFO  [ServiioConsole] Starting Serviio Console
2012-07-26 18:41:05,592 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 1
2012-07-26 18:41:05,923 DEBUG [SwingHelper] Choosing java look and feel: com.sun.java.swing.plaf.windows.WindowsLookAndFeel
2012-07-26 18:41:05,977 INFO  [ServiioConsole] Connecting to Serviio REST on localhost:23423
2012-07-26 18:41:06,439 INFO  [ProfilesDefinitionParser] Added profile 'Generic DLNA profile' (id=1)
2012-07-26 18:41:06,439 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 2
2012-07-26 18:41:07,045 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (B-series)' (id=2)
2012-07-26 18:41:07,045 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 3
2012-07-26 18:41:07,317 INFO  [ProfilesDefinitionParser] Added profile 'Xbox 360' (id=3)
2012-07-26 18:41:07,317 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 4
2012-07-26 18:41:07,711 INFO  [ProfilesDefinitionParser] Added profile 'Playstation 3' (id=4)
2012-07-26 18:41:07,712 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 5
2012-07-26 18:41:07,951 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (A-series)' (id=5)
2012-07-26 18:41:07,951 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 6
2012-07-26 18:41:08,113 INFO  [ProfilesDefinitionParser] Added profile 'DirecTV HD-DVR' (id=6)
2012-07-26 18:41:08,113 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 7
2012-07-26 18:41:08,743 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (C/D/E-series)' (id=7)
2012-07-26 18:41:08,743 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 8
2012-07-26 18:41:08,773 INFO  [ProfilesDefinitionParser] Added profile 'LG TV / player' (id=8)
2012-07-26 18:41:08,783 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2012
2012-07-26 18:41:09,477 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012)' (id=sony2012)
2012-07-26 18:41:09,477 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 10
2012-07-26 18:41:10,349 INFO  [ProfilesDefinitionParser] Added profile 'Sony BD Player / SMP' (id=10)
2012-07-26 18:41:10,351 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 13
2012-07-26 18:41:10,698 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba REGZA' (id=13)
2012-07-26 18:41:10,699 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 14
2012-07-26 18:41:10,890 INFO  [ProfilesDefinitionParser] Added profile 'WDTV Live' (id=14)
2012-07-26 18:41:10,892 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 15
2012-07-26 18:41:10,937 INFO  [ProfilesDefinitionParser] Added profile 'LaCie LaCinema Media Player' (id=15)
2012-07-26 18:41:10,939 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 16
2012-07-26 18:41:11,095 INFO  [ProfilesDefinitionParser] Added profile 'Manta WIFI Centre' (id=16)
2012-07-26 18:41:11,097 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 17
2012-07-26 18:41:11,176 INFO  [ProfilesDefinitionParser] Added profile 'Pure Flow' (id=17)
2012-07-26 18:41:11,179 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 18
2012-07-26 18:41:11,462 INFO  [ProfilesDefinitionParser] Added profile 'Sharp Aquos' (id=18)
2012-07-26 18:41:11,464 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2011
2012-07-26 18:41:11,524 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011)' (id=sony2011)
2012-07-26 18:41:11,526 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2010
2012-07-26 18:41:11,881 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2010)' (id=sony2010)
2012-07-26 18:41:11,883 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 20
2012-07-26 18:41:12,267 INFO  [ProfilesDefinitionParser] Added profile 'Philips' (id=20)
2012-07-26 18:41:12,269 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2009
2012-07-26 18:41:12,401 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2009)' (id=sony2009)
2012-07-26 18:41:12,403 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 22
2012-07-26 18:41:12,463 INFO  [ProfilesDefinitionParser] Added profile 'Denon AVR' (id=22)
2012-07-26 18:41:12,465 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2011a
2012-07-26 18:41:13,114 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera E/G/GT/DT/UT (2011/2012)' (id=viera2011a)
2012-07-26 18:41:13,117 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2011
2012-07-26 18:41:13,202 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera S/ST/VT (2011)' (id=viera2011)
2012-07-26 18:41:13,204 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2010
2012-07-26 18:41:13,318 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2010)' (id=viera2010)
2012-07-26 18:41:13,397 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2012-07-26 18:41:13,401 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile flv_player
2012-07-26 18:41:13,807 INFO  [ProfilesDefinitionParser] Added profile 'Flash player' (id=flv_player)
2012-07-26 18:41:13,809 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile serviigo
2012-07-26 18:41:14,100 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo' (id=serviigo)
2012-07-26 18:41:14,571 INFO  [Device] Created UPnP Device with UUID: c41c5514-0d79-3774-967b-023b9e31d01f, bound address: 192.168.0.2
2012-07-26 18:41:14,681 INFO  [PluginCompilerThread] Looking for plugins at C:\Program Files\Serviio\bin\..\plugins
2012-07-26 18:41:14,684 INFO  [UpdateChecker] Checking if a new version is available
2012-07-26 18:41:14,689 DEBUG [FFMPEGWrapper] Invoking FFMPEG to check if it exists of path C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe
2012-07-26 18:41:14,685 INFO  [PluginCompilerThread] Started looking for plugins
2012-07-26 18:41:14,709 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe
2012-07-26 18:41:15,385 INFO  [AbstractTranscodingDeliveryEngine] Cleaning transcode engine and its data
2012-07-26 18:41:15,385 DEBUG [AbstractTranscodingDeliveryEngine] Deleting temporary transcoded files from: C:\Windows\TEMP\Serviio
2012-07-26 18:41:15,387 DEBUG [AbstractTranscodingDeliveryEngine] Deleted file C:\Windows\TEMP\Serviio\transcoding-temp-12-viera2010-ORIGINAL.stf: true
2012-07-26 18:41:15,426 DEBUG [DiscoveryManager] UPNP device c41c5514-0d79-3774-967b-023b9e31d01f (192.168.0.2) is available
2012-07-26 18:41:15,431 INFO  [RendererSearchSender] Searching for Renderer devices
2012-07-26 18:41:15,431 INFO  [RendererExpirationChecker] Starting RendererExpirationChecker
2012-07-26 18:41:15,440 INFO  [EventDispatcher] Starting EventDispatcher
2012-07-26 18:41:15,440 INFO  [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2012-07-26 18:41:15,440 INFO  [WebServer] WebServer starting on port 8895
2012-07-26 18:41:15,440 INFO  [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2012-07-26 18:41:15,504 INFO  [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface eth3 (Realtek PCIe GBE Family Controller) and address 192.168.0.2, timeout = 0
2012-07-26 18:41:15,854 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth3 (Realtek PCIe GBE Family Controller) and address 192.168.0.2, timeout = 0
2012-07-26 18:41:15,874 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device c41c5514-0d79-3774-967b-023b9e31d01f
2012-07-26 18:41:15,927 DEBUG [WebServer] Incoming connection from /192.168.0.6:41511
2012-07-26 18:41:15,930 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:41:15,942 DEBUG [RendererSearchSender] Multicasting SSDP M-SEARCH using interface eth3 (Realtek PCIe GBE Family Controller) and address 192.168.0.2, timeout = 0
2012-07-26 18:41:15,943 DEBUG [RendererSearchSender] Sending 1 'm-search' messages
2012-07-26 18:41:15,944 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.2:54288
2012-07-26 18:41:15,944 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:15,946 DEBUG [RendererSearchSender] Received search response: location: http://192.168.0.6:55000/dmr/ddd.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2012-07-26 18:41:15,947 DEBUG [RendererSearchSender] Received a valid M-SEARCH response from Renderer 4d454930-0100-1000-8001-cc7ee77f022b from address /192.168.0.6:16320
2012-07-26 18:41:15,948 DEBUG [RendererSearchSender] Received search response: location: http://192.168.0.6:55000/dmr/ddd.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2012-07-26 18:41:15,950 DEBUG [RendererSearchSender] Received a valid M-SEARCH response from Renderer 4d454930-0100-1000-8001-cc7ee77f022b from address /192.168.0.6:45438
2012-07-26 18:41:15,951 DEBUG [RendererSearchSender] Received search response: location: http://192.168.0.6:55000/dmr/ddd.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2012-07-26 18:41:15,951 DEBUG [RendererSearchSender] Received a valid M-SEARCH response from Renderer 4d454930-0100-1000-8001-cc7ee77f022b from address /192.168.0.6:47781
2012-07-26 18:41:15,953 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c41c5514-0d79-3774-967b-023b9e31d01f from 192.168.0.6 (headers = [User-Agent: Panasonic MIL DLNA CP UPnP/1.0 DLNADOC/1.50,Host: 192.168.0.2:8895,X-PANASONIC-Registration: VklFUkEgRVQ1IFNlcmllcwAAAAAAAAAAAAA=,X-PANASONIC-DMP-Profile: MPEG_PS_PAL JPEG_SM PV_DIVX_DIV3 PV_DIVX_DIV4 PV_DIVX_DIVX PV_DIVX_DX50 PV_DRM_DIVX_DIV3 PV_DRM_DIVX_DIV4 PV_DRM_DIVX_DIVX PV_DRM_DIVX_DX50])
2012-07-26 18:41:15,954 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.2:54288
2012-07-26 18:41:15,954 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:41:15,954 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:15,955 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [User-Agent: Panasonic MIL DLNA CP UPnP/1.0 DLNADOC/1.50,Host: 192.168.0.2:8895,X-PANASONIC-Registration: VklFUkEgRVQ1IFNlcmllcwAAAAAAAAAAAAA=,X-PANASONIC-DMP-Profile: MPEG_PS_PAL JPEG_SM PV_DIVX_DIV3 PV_DIVX_DIV4 PV_DIVX_DIVX PV_DIVX_DX50 PV_DRM_DIVX_DIV3 PV_DRM_DIVX_DIV4 PV_DRM_DIVX_DIVX PV_DRM_DIVX_DX50]
2012-07-26 18:41:15,955 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.2:54288
2012-07-26 18:41:15,955 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:15,969 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:41:15,983 DEBUG [RendererManager] Retrieve device description from http://192.168.0.6:55000/dmr/ddd.xml
2012-07-26 18:41:16,382 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Panasonic Viera (2010)'
2012-07-26 18:41:16,515 DEBUG [RendererManager] Looking for a renderer profile for: friendly name = 'VIERA ET5 Series', model name= 'Panasonic VIErA', model number = 'null', manufacturer = 'Panasonic', product code = 'null', server name = 'FreeBSD/8.0 UPnP/1.0 Panasonic-MIL-DLNA-SV/1.0'
2012-07-26 18:41:16,516 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:41:16,517 DEBUG [RendererDAOImpl] Deleting a Renderer (uuid = 73180837-4d06-4de2-ad8e-9261cafdc4f2)
2012-07-26 18:41:16,611 DEBUG [RendererDAOImpl] Creating a new Renderer (uuid = 4d454930-0100-1000-8001-cc7ee77f022b)
2012-07-26 18:41:16,667 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:41:16,667 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:16,734 INFO  [RendererManager] Stored a new renderer: uuid='4d454930-0100-1000-8001-cc7ee77f022b', name = 'Panasonic VIErA', ipAddress='192.168.0.6', profile = 'viera2011a'
2012-07-26 18:41:16,734 DEBUG [RendererManager] Adding renderer Renderer [uuid=4d454930-0100-1000-8001-cc7ee77f022b, ipAddress=192.168.0.6, name=Panasonic VIErA, profileId=viera2011a, manuallyAdded=false, forcedProfile=false, enabled=true, accessGroupId=1] to list of active renderers
2012-07-26 18:41:17,145 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.2:61032
2012-07-26 18:41:17,145 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:17,147 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.0.2:61032
2012-07-26 18:41:17,147 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:17,148 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.2:61032
2012-07-26 18:41:17,735 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:10
2012-07-26 18:41:18,140 DEBUG [WebServer] Incoming connection from /192.168.0.2:49197
2012-07-26 18:41:18,140 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:18,142 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c41c5514-0d79-3774-967b-023b9e31d01f from 192.168.0.2 (headers = [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,Host: 192.168.0.2:8895,User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50])
2012-07-26 18:41:18,142 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:18,143 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,Host: 192.168.0.2:8895,User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50]
2012-07-26 18:41:18,144 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:18,146 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2012-07-26 18:41:18,179 DEBUG [WebServer] Incoming connection from /192.168.0.2:49198
2012-07-26 18:41:18,179 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:18,180 DEBUG [UPnPIconRequestHandler] UPnP icon request received for icon smallPNG
2012-07-26 18:41:19,667 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:41:19,667 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:20,145 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.2:61032
2012-07-26 18:41:20,145 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:20,147 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.0.2:61032
2012-07-26 18:41:20,147 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:20,148 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.2:61032
2012-07-26 18:41:21,943 DEBUG [RendererSearchSender] Finished searching for Renderer devices
2012-07-26 18:41:22,145 DEBUG [ServiioConsole] Retrieving console settings
2012-07-26 18:41:22,251 DEBUG [ServiioConsole] Setting console locale to be: en
2012-07-26 18:41:22,256 INFO  [Messages] Loaded console message bundle for locale: en
2012-07-26 18:41:22,424 DEBUG [ServiioFrameView] Initializing main frame
2012-07-26 18:41:22,444 DEBUG [ServiioFrameView] System tray is supported
2012-07-26 18:41:22,638 DEBUG [AccessGroupDAOImpl] Reading all AccessGroups
2012-07-26 18:41:23,042 DEBUG [RepositoryDAOImpl] Reading all Repositories
2012-07-26 18:41:23,081 DEBUG [AccessGroupDAOImpl] Reading all AccessGroups for Repository (id = 1)
2012-07-26 18:41:23,180 DEBUG [AccessGroupDAOImpl] Reading all AccessGroups for Repository (id = 2)
2012-07-26 18:41:23,183 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2012-07-26 18:41:23,649 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2012-07-26 18:41:23,837 DEBUG [AccessGroupDAOImpl] Reading all AccessGroups
2012-07-26 18:41:24,140 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type AUDIO
2012-07-26 18:41:24,159 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type VIDEO
2012-07-26 18:41:25,252 INFO  [ContentDirectoryDefinitionParser] Parsing ContentDirectory definition
2012-07-26 18:41:25,257 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=0, titleKey=serviio_media_library, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = null
2012-07-26 18:41:25,277 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A, titleKey=audio, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2012-07-26 18:41:25,285 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_A, titleKey=artists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,292 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListArtistInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,302 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListArtistsForInitialCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=ART, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,308 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAlbumsForArtistCommand, containerClass=MUSIC_ALBUM, itemClass=null, idPrefix=A, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,316 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForArtistAndAlbumCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,321 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=AL_A, titleKey=album_artists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,328 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListAlbumArtistInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,334 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListAlbumArtistsForInitialCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=ART, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,341 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAlbumsForAlbumArtistCommand, containerClass=MUSIC_ALBUM, itemClass=null, idPrefix=A, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,349 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForAlbumCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,355 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_F, titleKey=folders, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,362 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAudioFoldersByNameCommand, containerClass=STORAGE_FOLDER, itemClass=MUSIC_TRACK, idPrefix=FOL, recursive=true, cacheRegion=local_default
2012-07-26 18:41:25,368 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_G, titleKey=genres, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,376 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAudioGenresByNameCommand, containerClass=MUSIC_GENRE, itemClass=null, idPrefix=GEN, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,382 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForGenreCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,389 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_C, titleKey=composers, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,395 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListComposerInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,402 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListComposersForInitialCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=COM, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,409 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAlbumsForComposerCommand, containerClass=MUSIC_ALBUM, itemClass=null, idPrefix=A, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,415 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForComposerAndAlbumCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,423 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_T, titleKey=titles, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,429 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,437 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForInitialCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,443 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_R, titleKey=random, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2012-07-26 18:41:25,450 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListRandomSongsCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=no_cache
2012-07-26 18:41:25,456 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_PL, titleKey=playlists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,464 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAudioPlaylistsCommand, containerClass=PLAYLIST_CONTAINER, itemClass=null, idPrefix=PL, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,470 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForPlaylistCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,477 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_OF, titleKey=online, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2012-07-26 18:41:25,484 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAudioOnlineContentCommand, containerClass=STORAGE_FOLDER, itemClass=MUSIC_TRACK, idPrefix=FOL, recursive=true, cacheRegion=no_cache
2012-07-26 18:41:25,491 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_S, titleKey=All songs, class=CONTAINER, cacheRegion=local_default, browsable = false, editable = null
2012-07-26 18:41:25,498 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAllSongsCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,504 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_AS, titleKey=Artist Songs, class=CONTAINER, cacheRegion=local_default, browsable = false, editable = null
2012-07-26 18:41:25,511 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListArtistsByNameCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=ART_S, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,518 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForArtistCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,527 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_ALB, titleKey=All albums, class=CONTAINER, cacheRegion=local_default, browsable = false, editable = null
2012-07-26 18:41:25,534 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAllAlbumsCommand, containerClass=MUSIC_ALBUM, itemClass=null, idPrefix=ALB, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,540 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForAlbumCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,548 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I, titleKey=image, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2012-07-26 18:41:25,554 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_A, titleKey=albums, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,562 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListFlatImageFoldersByNameCommand, containerClass=CONTAINER, itemClass=null, idPrefix=ALB, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,568 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesForFolderCommand, containerClass=null, itemClass=PHOTO, idPrefix=I, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,576 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_F, titleKey=folders, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,584 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImageFoldersByNameCommand, containerClass=STORAGE_FOLDER, itemClass=PHOTO, idPrefix=FOL, recursive=true, cacheRegion=local_default
2012-07-26 18:41:25,595 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_Y, titleKey=year, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,602 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesCreationYearsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=YEAR, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,610 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesForCreationYearCommand, containerClass=null, itemClass=PHOTO, idPrefix=I, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,616 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_T, titleKey=month, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,622 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesCreationYearsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=YEAR, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,630 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesCreationMonthsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=MON, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,637 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesForCreationMonthAndYearCommand, containerClass=null, itemClass=PHOTO, idPrefix=I, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,644 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_PL, titleKey=playlists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,650 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagePlaylistsCommand, containerClass=PLAYLIST_CONTAINER, itemClass=null, idPrefix=PL, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,659 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesForPlaylistCommand, containerClass=null, itemClass=PHOTO, idPrefix=S, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,666 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_OF, titleKey=online, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2012-07-26 18:41:25,675 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImageOnlineContentCommand, containerClass=STORAGE_FOLDER, itemClass=PHOTO, idPrefix=FOL, recursive=true, cacheRegion=no_cache
2012-07-26 18:41:25,681 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_AI, titleKey=All images, class=CONTAINER, cacheRegion=local_default, browsable = false, editable = null
2012-07-26 18:41:25,689 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListAllImagesCommand, containerClass=null, itemClass=PHOTO, idPrefix=I, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,696 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V, titleKey=video, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2012-07-26 18:41:25,704 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_T, titleKey=titles, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,712 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,719 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForInitialCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,726 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_M, titleKey=movies, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,733 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListMovieVideosByNameCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,741 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_S, titleKey=series, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,748 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListSeriesByNameCommand, containerClass=CONTAINER, itemClass=null, idPrefix=SER, recursive=false, cacheRegion=local_resetafterplay
2012-07-26 18:41:25,755 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListSeasonsForSeriesCommand, containerClass=CONTAINER, itemClass=null, idPrefix=SSN, recursive=false, cacheRegion=local_resetafterplay
2012-07-26 18:41:25,762 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListEpisodesForSeriesSeasonCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_resetafterplay
2012-07-26 18:41:25,768 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_F, titleKey=folders, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,777 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoFoldersByNameCommand, containerClass=STORAGE_FOLDER, itemClass=VIDEO_ITEM, idPrefix=FOL, recursive=true, cacheRegion=local_default
2012-07-26 18:41:25,784 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_LV, titleKey=last_viewed, class=CONTAINER, cacheRegion=local_resetafterplay, browsable = null, editable = true
2012-07-26 18:41:25,791 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListLastViewedVideosCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_resetafterplay
2012-07-26 18:41:25,798 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_LA, titleKey=last_added, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,806 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListLastAddedVideosCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,813 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_G, titleKey=genres, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,821 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoGenresByNameCommand, containerClass=CONTAINER, itemClass=null, idPrefix=GEN, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,828 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForGenreCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,836 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_A, titleKey=actors, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,843 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListActorInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,851 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListActorsForInitialCommand, containerClass=PERSON, itemClass=null, idPrefix=P, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,858 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForActorCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,865 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_D, titleKey=directors, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,872 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListDirectorInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,879 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListDirectorsForInitialCommand, containerClass=PERSON, itemClass=null, idPrefix=P, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,887 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForDirectorCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,894 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_P, titleKey=producers, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,902 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListProducerInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,909 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListProducersForInitialCommand, containerClass=PERSON, itemClass=null, idPrefix=P, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,917 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForProducerCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,924 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_PL, titleKey=playlists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2012-07-26 18:41:25,933 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoPlaylistsCommand, containerClass=PLAYLIST_CONTAINER, itemClass=null, idPrefix=PL, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,940 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForPlaylistCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=S, recursive=false, cacheRegion=local_default
2012-07-26 18:41:25,948 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_OF, titleKey=online, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2012-07-26 18:41:25,955 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoOnlineContentCommand, containerClass=STORAGE_FOLDER, itemClass=VIDEO_ITEM, idPrefix=FOL, recursive=true, cacheRegion=no_cache
2012-07-26 18:41:25,960 INFO  [BrowsingCategoriesMessages] Loaded browsing categories message bundle for locale: en
2012-07-26 18:41:26,309 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:41:26,310 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:26,841 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:41:26,841 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:26,962 DEBUG [RepositoryDAOImpl] Reading all Repositories
2012-07-26 18:41:26,963 DEBUG [AccessGroupDAOImpl] Reading all AccessGroups for Repository (id = 1)
2012-07-26 18:41:26,964 DEBUG [AccessGroupDAOImpl] Reading all AccessGroups for Repository (id = 2)
2012-07-26 18:41:26,965 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2012-07-26 18:41:26,993 DEBUG [ServiioConsole] Reading application data
2012-07-26 18:41:26,993 DEBUG [RepositoryView$LibraryStatusCheckerThread] Starting library status checking thread
2012-07-26 18:41:28,007 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth3 (Realtek PCIe GBE Family Controller) and address 192.168.0.2, timeout = 0
2012-07-26 18:41:28,007 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device c41c5514-0d79-3774-967b-023b9e31d01f
2012-07-26 18:41:28,650 DEBUG [WebServer] Incoming connection from /192.168.0.6:61235
2012-07-26 18:41:28,650 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:41:28,651 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c41c5514-0d79-3774-967b-023b9e31d01f from 192.168.0.6 (headers = [User-Agent: Panasonic MIL DLNA CP UPnP/1.0 DLNADOC/1.50,Host: 192.168.0.2:8895,X-PANASONIC-Registration: VklFUkEgRVQ1IFNlcmllcwAAAAAAAAAAAAA=,X-PANASONIC-DMP-Profile: MPEG_PS_PAL JPEG_SM PV_DIVX_DIV3 PV_DIVX_DIV4 PV_DIVX_DIVX PV_DIVX_DX50 PV_DRM_DIVX_DIV3 PV_DRM_DIVX_DIV4 PV_DRM_DIVX_DIVX PV_DRM_DIVX_DX50])
2012-07-26 18:41:28,652 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:41:28,653 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [User-Agent: Panasonic MIL DLNA CP UPnP/1.0 DLNADOC/1.50,Host: 192.168.0.2:8895,X-PANASONIC-Registration: VklFUkEgRVQ1IFNlcmllcwAAAAAAAAAAAAA=,X-PANASONIC-DMP-Profile: MPEG_PS_PAL JPEG_SM PV_DIVX_DIV3 PV_DIVX_DIV4 PV_DIVX_DIVX PV_DIVX_DX50 PV_DRM_DIVX_DIV3 PV_DRM_DIVX_DIV4 PV_DRM_DIVX_DIVX PV_DRM_DIVX_DX50]
2012-07-26 18:41:28,654 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:41:28,654 DEBUG [RendererDAOImpl] Deleting a Renderer (uuid = 4d454930-0100-1000-8001-cc7ee77f022b)
2012-07-26 18:41:28,660 DEBUG [RendererManager] Removing renderer 4d454930-0100-1000-8001-cc7ee77f022b from list of active renderers
2012-07-26 18:41:28,660 DEBUG [RendererDAOImpl] Creating a new Renderer (uuid = 8ba09be3-e133-47fe-9c97-bb522a1bf918)
2012-07-26 18:41:28,662 INFO  [RendererManager] Stored a new renderer: uuid='8ba09be3-e133-47fe-9c97-bb522a1bf918', name = 'Panasonic Viera (2010)', ipAddress='192.168.0.6', profile = 'viera2010'
2012-07-26 18:41:28,662 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:41:28,668 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Panasonic Viera (2010)'
2012-07-26 18:41:29,310 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:41:29,310 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:29,808 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:10
2012-07-26 18:41:29,841 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:41:29,841 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:32,310 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:41:32,310 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:32,841 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:41:32,841 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:35,853 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:41:35,853 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:38,853 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:41:38,853 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:40,076 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth3 (Realtek PCIe GBE Family Controller) and address 192.168.0.2, timeout = 0
2012-07-26 18:41:40,076 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device c41c5514-0d79-3774-967b-023b9e31d01f
2012-07-26 18:41:40,719 DEBUG [WebServer] Incoming connection from /192.168.0.6:33056
2012-07-26 18:41:40,719 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:41:40,720 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c41c5514-0d79-3774-967b-023b9e31d01f from 192.168.0.6 (headers = [User-Agent: Panasonic MIL DLNA CP UPnP/1.0 DLNADOC/1.50,Host: 192.168.0.2:8895,X-PANASONIC-Registration: VklFUkEgRVQ1IFNlcmllcwAAAAAAAAAAAAA=,X-PANASONIC-DMP-Profile: MPEG_PS_PAL JPEG_SM PV_DIVX_DIV3 PV_DIVX_DIV4 PV_DIVX_DIVX PV_DIVX_DX50 PV_DRM_DIVX_DIV3 PV_DRM_DIVX_DIV4 PV_DRM_DIVX_DIVX PV_DRM_DIVX_DX50])
2012-07-26 18:41:40,720 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:41:40,721 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [User-Agent: Panasonic MIL DLNA CP UPnP/1.0 DLNADOC/1.50,Host: 192.168.0.2:8895,X-PANASONIC-Registration: VklFUkEgRVQ1IFNlcmllcwAAAAAAAAAAAAA=,X-PANASONIC-DMP-Profile: MPEG_PS_PAL JPEG_SM PV_DIVX_DIV3 PV_DIVX_DIV4 PV_DIVX_DIVX PV_DIVX_DX50 PV_DRM_DIVX_DIV3 PV_DRM_DIVX_DIV4 PV_DRM_DIVX_DIVX PV_DRM_DIVX_DX50]
2012-07-26 18:41:40,722 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:41:40,724 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Panasonic Viera (2010)'
2012-07-26 18:41:41,854 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:41:41,854 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:41:41,876 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:59
2012-07-26 18:41:41,960 INFO  [FeedUpdaterThread] Started looking for information about online resources
2012-07-26 18:41:41,960 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2012-07-26 18:41:41,960 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2012-07-26 18:41:41,961 INFO  [LibraryAdditionsCheckerThread] Started looking for newly added files
2012-07-26 18:41:41,961 DEBUG [RepositoryDAOImpl] Reading all Repositories
2012-07-26 18:41:41,962 INFO  [LibraryUpdatesCheckerThread] Started looking for updates to currently shared files
2012-07-26 18:41:41,962 DEBUG [RepositoryDAOImpl] Reading all Repositories
2012-07-26 18:41:41,963 INFO  [PlaylistMaintainerThread] Started looking playlist changes
2012-07-26 18:41:41,964 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: C:\Users\Mark\Videos
2012-07-26 18:41:41,964 DEBUG [LibraryUpdatesCheckerThread] Looking for removed playlist files in repository: C:\Users\Mark\Videos
2012-07-26 18:41:41,965 DEBUG [LibraryAdditionsCheckerThread] Found file '652039main_ksc_052209_spacex_launch_pod.mp4', checking if it's already in the Library
2012-07-26 18:41:41,972 DEBUG [PlaylistDAOImpl] Reading all Playlists
2012-07-26 18:41:41,972 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item 652039main_ksc_052209_spacex_launch_pod.mp4
2012-07-26 18:41:41,972 DEBUG [PlaylistDAOImpl] Reading Playlists for Repository (id = 1)
2012-07-26 18:41:41,973 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\652039main_ksc_052209_spacex_launch_pod.mp4, ignore case: true
2012-07-26 18:41:42,060 DEBUG [LibraryUpdatesCheckerThread] Looking for removed/updated files in repository: C:\Users\Mark\Videos
2012-07-26 18:41:42,061 DEBUG [MediaItemDAOImpl] Reading MediaItems for Repository (id = 1)
2012-07-26 18:41:42,096 DEBUG [MediaItemDAOImpl] Media item 652039main_ksc_052209_spacex_launch_pod.mp4 already exists in DB
2012-07-26 18:41:42,096 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: C:\Users\Mark\Videos\BBC iPlayer
2012-07-26 18:41:42,096 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: C:\Users\Mark\Videos\BBC iPlayer\repository
2012-07-26 18:41:42,097 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: C:\Users\Mark\Videos\BBC iPlayer\repository\cache
2012-07-26 18:41:42,102 DEBUG [MediaItemDAOImpl] Getting file of media item 1
2012-07-26 18:41:42,113 DEBUG [LibraryAdditionsCheckerThread] Found file 'bbc_four.mp4', checking if it's already in the Library
2012-07-26 18:41:42,113 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item bbc_four.mp4
2012-07-26 18:41:42,113 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\BBC iPlayer\repository\cache\bbc_four.mp4, ignore case: true
2012-07-26 18:41:42,114 DEBUG [MediaItemDAOImpl] Media item bbc_four.mp4 already exists in DB
2012-07-26 18:41:42,128 DEBUG [LibraryAdditionsCheckerThread] Found file 'bbc_hd.mp4', checking if it's already in the Library
2012-07-26 18:41:42,128 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item bbc_hd.mp4
2012-07-26 18:41:42,128 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\BBC iPlayer\repository\cache\bbc_hd.mp4, ignore case: true
2012-07-26 18:41:42,129 DEBUG [MediaItemDAOImpl] Media item bbc_hd.mp4 already exists in DB
2012-07-26 18:41:42,147 DEBUG [LibraryAdditionsCheckerThread] Found file 'bbc_one.mp4', checking if it's already in the Library
2012-07-26 18:41:42,147 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item bbc_one.mp4
2012-07-26 18:41:42,147 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\BBC iPlayer\repository\cache\bbc_one.mp4, ignore case: true
2012-07-26 18:41:42,148 DEBUG [MediaItemDAOImpl] Media item bbc_one.mp4 already exists in DB
2012-07-26 18:41:42,151 DEBUG [LibraryAdditionsCheckerThread] Found file 'bbc_two.mp4', checking if it's already in the Library
2012-07-26 18:41:42,151 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item bbc_two.mp4
2012-07-26 18:41:42,151 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\BBC iPlayer\repository\cache\bbc_two.mp4, ignore case: true
2012-07-26 18:41:42,152 DEBUG [MediaItemDAOImpl] Media item bbc_two.mp4 already exists in DB
2012-07-26 18:41:42,152 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type AUDIO
2012-07-26 18:41:42,152 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: C:\Users\Mark\Videos\DigiTV Recordings
2012-07-26 18:41:42,153 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: C:\Users\Mark\Videos\Test Videos
2012-07-26 18:41:42,155 DEBUG [LibraryAdditionsCheckerThread] Found file 'AmosOcean_DivXPlus_10Mbps.mkv', checking if it's already in the Library
2012-07-26 18:41:42,155 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item AmosOcean_DivXPlus_10Mbps.mkv
2012-07-26 18:41:42,155 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\Test Videos\AmosOcean_DivXPlus_10Mbps.mkv, ignore case: true
2012-07-26 18:41:42,156 DEBUG [MediaItemDAOImpl] Media item AmosOcean_DivXPlus_10Mbps.mkv already exists in DB
2012-07-26 18:41:42,156 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type VIDEO
2012-07-26 18:41:42,158 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type IMAGE
2012-07-26 18:41:42,160 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 1) and extractor EMBEDDED
2012-07-26 18:41:42,164 DEBUG [LibraryAdditionsCheckerThread] Found file 'AmosSurfing_DivXPlus_10Mbps.mkv', checking if it's already in the Library
2012-07-26 18:41:42,164 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item AmosSurfing_DivXPlus_10Mbps.mkv
2012-07-26 18:41:42,164 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\Test Videos\AmosSurfing_DivXPlus_10Mbps.mkv, ignore case: true
2012-07-26 18:41:42,165 DEBUG [MediaItemDAOImpl] Media item AmosSurfing_DivXPlus_10Mbps.mkv already exists in DB
2012-07-26 18:41:42,201 DEBUG [LibraryAdditionsCheckerThread] Found file 'hbo_trailer_6k.ts', checking if it's already in the Library
2012-07-26 18:41:42,201 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item hbo_trailer_6k.ts
2012-07-26 18:41:42,201 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\Test Videos\hbo_trailer_6k.ts, ignore case: true
2012-07-26 18:41:42,203 DEBUG [MediaItemDAOImpl] Media item hbo_trailer_6k.ts already exists in DB
2012-07-26 18:41:42,217 DEBUG [LibraryAdditionsCheckerThread] Found file 'hbo_trailer_dtc.ts', checking if it's already in the Library
2012-07-26 18:41:42,217 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item hbo_trailer_dtc.ts
2012-07-26 18:41:42,217 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\Test Videos\hbo_trailer_dtc.ts, ignore case: true
2012-07-26 18:41:42,219 DEBUG [MediaItemDAOImpl] Media item hbo_trailer_dtc.ts already exists in DB
2012-07-26 18:41:42,219 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: C:\Users\Mark\Videos\Test Videos\matroska
2012-07-26 18:41:42,245 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 1) and extractor COVER_IMAGE_IN_FOLDER
2012-07-26 18:41:42,286 DEBUG [MediaItemDAOImpl] Getting file of media item 2
2012-07-26 18:41:42,286 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 2) and extractor EMBEDDED
2012-07-26 18:41:42,288 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 2) and extractor COVER_IMAGE_IN_FOLDER
2012-07-26 18:41:42,289 DEBUG [MediaItemDAOImpl] Getting file of media item 3
2012-07-26 18:41:42,290 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 3) and extractor EMBEDDED
2012-07-26 18:41:42,291 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 3) and extractor COVER_IMAGE_IN_FOLDER
2012-07-26 18:41:42,292 DEBUG [MediaItemDAOImpl] Getting file of media item 4
2012-07-26 18:41:42,293 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 4) and extractor EMBEDDED
2012-07-26 18:41:42,294 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 4) and extractor COVER_IMAGE_IN_FOLDER
2012-07-26 18:41:42,295 DEBUG [MediaItemDAOImpl] Getting file of media item 5
2012-07-26 18:41:42,296 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 5) and extractor EMBEDDED
2012-07-26 18:41:42,297 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 5) and extractor COVER_IMAGE_IN_FOLDER
2012-07-26 18:41:42,298 DEBUG [MediaItemDAOImpl] Getting file of media item 6
2012-07-26 18:41:42,299 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 6) and extractor EMBEDDED
2012-07-26 18:41:42,300 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 6) and extractor COVER_IMAGE_IN_FOLDER
2012-07-26 18:41:42,301 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2012-07-26 18:41:42,302 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 7) and extractor EMBEDDED
2012-07-26 18:41:42,303 DEBUG [LibraryAdditionsCheckerThread] Found file 'test1.mkv', checking if it's already in the Library
2012-07-26 18:41:42,303 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item test1.mkv
2012-07-26 18:41:42,303 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\Test Videos\matroska\test1.mkv, ignore case: true
2012-07-26 18:41:42,303 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 7) and extractor COVER_IMAGE_IN_FOLDER
2012-07-26 18:41:42,304 DEBUG [MediaItemDAOImpl] Media item test1.mkv already exists in DB
2012-07-26 18:41:42,305 DEBUG [MediaItemDAOImpl] Getting file of media item 8
2012-07-26 18:41:42,305 DEBUG [LibraryAdditionsCheckerThread] Found file 'test2.mkv', checking if it's already in the Library
2012-07-26 18:41:42,305 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item test2.mkv
2012-07-26 18:41:42,306 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\Test Videos\matroska\test2.mkv, ignore case: true
2012-07-26 18:41:42,306 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 8) and extractor EMBEDDED
2012-07-26 18:41:42,307 DEBUG [MediaItemDAOImpl] Media item test2.mkv already exists in DB
2012-07-26 18:41:42,307 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 8) and extractor COVER_IMAGE_IN_FOLDER
2012-07-26 18:41:42,308 DEBUG [LibraryAdditionsCheckerThread] Found file 'test3.mkv', checking if it's already in the Library
2012-07-26 18:41:42,308 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item test3.mkv
2012-07-26 18:41:42,308 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\Test Videos\matroska\test3.mkv, ignore case: true
2012-07-26 18:41:42,309 DEBUG [MediaItemDAOImpl] Media item test3.mkv already exists in DB
2012-07-26 18:41:42,309 DEBUG [MediaItemDAOImpl] Getting file of media item 9
2012-07-26 18:41:42,310 DEBUG [LibraryAdditionsCheckerThread] Found file 'test4.mkv', checking if it's already in the Library
2012-07-26 18:41:42,310 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item test4.mkv
2012-07-26 18:41:42,310 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 9) and extractor EMBEDDED
2012-07-26 18:41:42,310 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: C:\Users\Mark\Videos\Test Videos\matroska\test4.mkv, ignore case: true
2012-07-26 18:41:42,311 DEBUG [MediaItemDAOImpl] Media item test4.mkv doesn't exist in DB yet
2012-07-26 18:41:42,311 DEBUG [LibraryAdditionsCheckerThread] File not in Library, will add it
2012-07-26 18:41:42,311 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 9) and extractor COVER_IMAGE_IN_FOLDER
2012-07-26 18:41:42,313 DEBUG [MediaItemDAOImpl] Getting file of media item 10
2012-07-26 18:41:42,314 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 10) and extractor EMBEDDED
2012-07-26 18:41:42,314 DEBUG [VideoExtractionStrategy] Extracting metadata of video file: C:\Users\Mark\Videos\Test Videos\matroska\test4.mkv
2012-07-26 18:41:42,316 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 10) and extractor COVER_IMAGE_IN_FOLDER
2012-07-26 18:41:42,317 DEBUG [FFMPEGWrapper] Invoking FFMPEG to retrieve media information for file: C:\Users\Mark\Videos\Test Videos\matroska\test4.mkv
2012-07-26 18:41:42,317 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i C:\Users\Mark\Videos\Test Videos\matroska\test4.mkv
2012-07-26 18:41:42,334 DEBUG [MediaItemDAOImpl] Getting file of media item 11
2012-07-26 18:41:42,335 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 11) and extractor EMBEDDED
2012-07-26 18:41:42,336 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 11) and extractor COVER_IMAGE_IN_FOLDER
2012-07-26 18:41:42,338 DEBUG [MediaItemDAOImpl] Getting file of media item 12
2012-07-26 18:41:42,339 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 12) and extractor EMBEDDED
2012-07-26 18:41:42,340 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 12) and extractor COVER_IMAGE_IN_FOLDER
2012-07-26 18:41:42,342 DEBUG [LibraryUpdatesCheckerThread] Looking for removed playlist files in repository: C:\Users\Mark\Pictures
2012-07-26 18:41:42,342 DEBUG [PlaylistDAOImpl] Reading Playlists for Repository (id = 2)
2012-07-26 18:41:42,343 DEBUG [LibraryUpdatesCheckerThread] Looking for removed/updated files in repository: C:\Users\Mark\Pictures
2012-07-26 18:41:42,343 DEBUG [MediaItemDAOImpl] Reading MediaItems for Repository (id = 2)
2012-07-26 18:41:42,343 DEBUG [MediaItemDAOImpl] Reading dirty MediaItems for Repository (id = 1)
2012-07-26 18:41:42,363 DEBUG [RepositoryDAOImpl] Marking Repository 1 as scanned with current timestamp
2012-07-26 18:41:42,416 DEBUG [MediaItemDAOImpl] Reading dirty MediaItems for Repository (id = 2)
2012-07-26 18:41:42,417 DEBUG [RepositoryDAOImpl] Marking Repository 2 as scanned with current timestamp
2012-07-26 18:42:23,147 DEBUG [WebServer] I/O error: Connection reset
2012-07-26 18:42:35,799 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2012-07-26 18:42:35,800 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2012-07-26 18:42:35,800 DEBUG [ConfigEntryDAOImpl] Reading a ConfigEntry (name = search_hidden_files)
2012-07-26 18:42:35,800 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2012-07-26 18:42:35,806 DEBUG [ConfigEntryDAOImpl] Updating ConfigEntry (name = search_hidden_files, value = false)
2012-07-26 18:42:35,816 DEBUG [ConfigEntryDAOImpl] Reading a ConfigEntry (name = search_updated_files)
2012-07-26 18:42:35,816 DEBUG [ConfigEntryDAOImpl] Updating ConfigEntry (name = search_updated_files, value = true)
2012-07-26 18:42:35,818 DEBUG [ConfigEntryDAOImpl] Reading a ConfigEntry (name = repository_automatic_check)
2012-07-26 18:42:35,818 DEBUG [ConfigEntryDAOImpl] Updating ConfigEntry (name = repository_automatic_check, value = true)
2012-07-26 18:42:35,820 DEBUG [ConfigEntryDAOImpl] Reading a ConfigEntry (name = repository_automatic_check_interval)
2012-07-26 18:42:35,821 DEBUG [ConfigEntryDAOImpl] Updating ConfigEntry (name = repository_automatic_check_interval, value = 5)
2012-07-26 18:42:35,822 DEBUG [ConfigEntryDAOImpl] Reading a ConfigEntry (name = online_feed_max_num_items)
2012-07-26 18:42:35,823 DEBUG [ConfigEntryDAOImpl] Updating ConfigEntry (name = online_feed_max_num_items, value = 20)
2012-07-26 18:42:35,839 DEBUG [ConfigEntryDAOImpl] Reading a ConfigEntry (name = online_feed_expiry_interval)
2012-07-26 18:42:35,839 DEBUG [ConfigEntryDAOImpl] Updating ConfigEntry (name = online_feed_expiry_interval, value = 24)
2012-07-26 18:42:41,840 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth3 (Realtek PCIe GBE Family Controller) and address 192.168.0.2, timeout = 0
2012-07-26 18:42:41,841 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device c41c5514-0d79-3774-967b-023b9e31d01f
2012-07-26 18:42:42,485 DEBUG [WebServer] Incoming connection from /192.168.0.6:44576
2012-07-26 18:42:42,485 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:42:42,486 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c41c5514-0d79-3774-967b-023b9e31d01f from 192.168.0.6 (headers = [User-Agent: Panasonic MIL DLNA CP UPnP/1.0 DLNADOC/1.50,Host: 192.168.0.2:8895,X-PANASONIC-Registration: VklFUkEgRVQ1IFNlcmllcwAAAAAAAAAAAAA=,X-PANASONIC-DMP-Profile: MPEG_PS_PAL JPEG_SM PV_DIVX_DIV3 PV_DIVX_DIV4 PV_DIVX_DIVX PV_DIVX_DX50 PV_DRM_DIVX_DIV3 PV_DRM_DIVX_DIV4 PV_DRM_DIVX_DIVX PV_DRM_DIVX_DX50])
2012-07-26 18:42:42,486 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:42:42,487 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [User-Agent: Panasonic MIL DLNA CP UPnP/1.0 DLNADOC/1.50,Host: 192.168.0.2:8895,X-PANASONIC-Registration: VklFUkEgRVQ1IFNlcmllcwAAAAAAAAAAAAA=,X-PANASONIC-DMP-Profile: MPEG_PS_PAL JPEG_SM PV_DIVX_DIV3 PV_DIVX_DIV4 PV_DIVX_DIVX PV_DIVX_DX50 PV_DRM_DIVX_DIV3 PV_DRM_DIVX_DIV4 PV_DRM_DIVX_DIVX PV_DRM_DIVX_DX50]
2012-07-26 18:42:42,488 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:42:42,490 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Panasonic Viera (2010)'
2012-07-26 18:42:43,641 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:46
2012-07-26 18:42:58,356 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:42:58,356 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:43:01,356 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:43:01,356 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:43:04,356 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.2:61032
2012-07-26 18:43:04,356 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:43:30,375 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth3 (Realtek PCIe GBE Family Controller) and address 192.168.0.2, timeout = 0
2012-07-26 18:43:30,376 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device c41c5514-0d79-3774-967b-023b9e31d01f
2012-07-26 18:43:31,015 DEBUG [WebServer] Incoming connection from /192.168.0.6:19402
2012-07-26 18:43:31,015 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:43:31,016 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c41c5514-0d79-3774-967b-023b9e31d01f from 192.168.0.6 (headers = [User-Agent: Panasonic MIL DLNA CP UPnP/1.0 DLNADOC/1.50,Host: 192.168.0.2:8895,X-PANASONIC-Registration: VklFUkEgRVQ1IFNlcmllcwAAAAAAAAAAAAA=,X-PANASONIC-DMP-Profile: MPEG_PS_PAL JPEG_SM PV_DIVX_DIV3 PV_DIVX_DIV4 PV_DIVX_DIVX PV_DIVX_DX50 PV_DRM_DIVX_DIV3 PV_DRM_DIVX_DIV4 PV_DRM_DIVX_DIVX PV_DRM_DIVX_DX50])
2012-07-26 18:43:31,016 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:43:31,017 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [User-Agent: Panasonic MIL DLNA CP UPnP/1.0 DLNADOC/1.50,Host: 192.168.0.2:8895,X-PANASONIC-Registration: VklFUkEgRVQ1IFNlcmllcwAAAAAAAAAAAAA=,X-PANASONIC-DMP-Profile: MPEG_PS_PAL JPEG_SM PV_DIVX_DIV3 PV_DIVX_DIV4 PV_DIVX_DIVX PV_DIVX_DX50 PV_DRM_DIVX_DIV3 PV_DRM_DIVX_DIV4 PV_DRM_DIVX_DIVX PV_DRM_DIVX_DX50]
2012-07-26 18:43:31,018 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:43:31,021 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Panasonic Viera (2010)'
2012-07-26 18:43:31,898 DEBUG [RendererManager] Retrieve device description from http://192.168.0.6:55000/dmr/ddd.xml
2012-07-26 18:43:32,176 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:56
2012-07-26 18:43:32,718 DEBUG [RendererManager] Looking for a renderer profile for: friendly name = 'VIERA ET5 Series', model name= 'Panasonic VIErA', model number = 'null', manufacturer = 'Panasonic', product code = 'null', server name = 'FreeBSD/8.0 UPnP/1.0 Panasonic-MIL-DLNA-SV/1.0'
2012-07-26 18:43:32,719 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.6
2012-07-26 18:43:32,720 DEBUG [RendererDAOImpl] Deleting a Renderer (uuid = 8ba09be3-e133-47fe-9c97-bb522a1bf918)
2012-07-26 18:43:32,721 DEBUG [RendererDAOImpl] Creating a new Renderer (uuid = 4d454930-0100-1000-8001-cc7ee77f022b)
2012-07-26 18:43:32,723 INFO  [RendererManager] Stored a new renderer: uuid='4d454930-0100-1000-8001-cc7ee77f022b', name = 'Panasonic VIErA', ipAddress='192.168.0.6', profile = 'viera2011a'
2012-07-26 18:43:32,723 DEBUG [RendererManager] Adding renderer Renderer [uuid=4d454930-0100-1000-8001-cc7ee77f022b, ipAddress=192.168.0.6, name=Panasonic VIErA, profileId=viera2011a, manuallyAdded=false, forcedProfile=false, enabled=true, accessGroupId=1] to list of active renderers
2012-07-26 18:43:35,801 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2012-07-26 18:43:35,801 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2012-07-26 18:44:15,226 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.0.2:61032
2012-07-26 18:44:15,226 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:44:15,229 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.2:61032
2012-07-26 18:44:15,311 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-wifialliance-org:device:WFADevice:1 from address /192.168.0.2:61032
2012-07-26 18:44:15,312 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:44:16,755 DEBUG [WebServer] Incoming connection from /192.168.0.2:49518
2012-07-26 18:44:16,755 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:44:16,756 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c41c5514-0d79-3774-967b-023b9e31d01f from 192.168.0.2 (headers = [Cache-Control: no-cache,Connection: Keep-Alive,Pragma: no-cache,Accept: text/xml, application/xml,User-Agent: FDSSDP,Host: 192.168.0.2:8895])
2012-07-26 18:44:16,757 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:44:16,757 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Cache-Control: no-cache,Connection: Keep-Alive,Pragma: no-cache,Accept: text/xml, application/xml,User-Agent: FDSSDP,Host: 192.168.0.2:8895]
2012-07-26 18:44:16,758 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:44:16,760 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2012-07-26 18:44:18,226 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.0.2:61032
2012-07-26 18:44:18,226 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:44:18,227 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.2:61032
2012-07-26 18:44:18,312 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-wifialliance-org:device:WFADevice:1 from address /192.168.0.2:61032
2012-07-26 18:44:18,312 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:44:21,226 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.0.2:61032
2012-07-26 18:44:21,226 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2012-07-26 18:44:21,227 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.2:61032
2012-07-26 18:44:21,312 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-wifialliance-org:device:WFADevice:1 from address /192.168.0.2:61032
2012-07-26 18:44:21,312 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
